Dec 02 11:03:46.234 INFO testing wemo-0.0.12 against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 02 11:03:46.235 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-7/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 11:03:46.449 INFO [stdout] 3267878b57a513c3053659ccb2dbe5be7617554b1b72b542f1c1cde29c5db996
Dec 02 11:03:46.451 INFO running `"docker" "start" "-a" "3267878b57a513c3053659ccb2dbe5be7617554b1b72b542f1c1cde29c5db996"`
Dec 02 11:03:46.870 INFO [stderr] usermod: no changes
Dec 02 11:03:47.037 INFO [stderr]    Compiling num_cpus v0.2.13
Dec 02 11:03:47.039 INFO [stderr]    Compiling net2 v0.2.33
Dec 02 11:03:47.040 INFO [stderr]    Compiling memchr v0.1.11
Dec 02 11:03:47.040 INFO [stderr]    Compiling get_if_addrs v0.4.1
Dec 02 11:03:47.051 INFO [stderr]    Compiling url v1.4.1
Dec 02 11:03:47.067 INFO [stderr]    Compiling thread-id v2.0.0
Dec 02 11:03:48.399 INFO [stderr]    Compiling thread_local v0.2.7
Dec 02 11:03:48.841 INFO [stderr]    Compiling aho-corasick v0.5.3
Dec 02 11:03:49.299 INFO [stderr]    Compiling miow v0.1.5
Dec 02 11:03:50.863 INFO [stderr]    Compiling mio v0.5.1
Dec 02 11:03:51.311 INFO [stderr]    Compiling regex v0.1.80
Dec 02 11:03:53.591 INFO [stderr]    Compiling cookie v0.2.5
Dec 02 11:03:57.329 INFO [stderr]    Compiling hyper v0.9.18
Dec 02 11:04:12.997 INFO [stderr]    Compiling iron v0.4.0
Dec 02 11:04:15.361 INFO [stderr]    Compiling persistent v0.2.1
Dec 02 11:04:17.669 INFO [stderr]    Compiling bodyparser v0.4.1
Dec 02 11:04:20.706 INFO [stderr]    Compiling urlencoded v0.4.1
Dec 02 11:04:23.396 INFO [stderr]    Compiling wemo v0.0.12 (/source)
Dec 02 11:04:29.231 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 42.34s
Dec 02 11:04:29.243 INFO [stderr] su: No module specific data is present
Dec 02 11:04:29.791 INFO running `"docker" "inspect" "3267878b57a513c3053659ccb2dbe5be7617554b1b72b542f1c1cde29c5db996"`
Dec 02 11:04:30.045 INFO running `"docker" "rm" "-f" "3267878b57a513c3053659ccb2dbe5be7617554b1b72b542f1c1cde29c5db996"`
Dec 02 11:04:30.263 INFO [stdout] 3267878b57a513c3053659ccb2dbe5be7617554b1b72b542f1c1cde29c5db996
Dec 02 11:04:30.265 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-7/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 11:04:30.779 INFO [stdout] 6f3d2ccb566c0e5cfd854543ada1e21898cb55effc1b935036f8fd5c3373705e
Dec 02 11:04:30.781 INFO running `"docker" "start" "-a" "6f3d2ccb566c0e5cfd854543ada1e21898cb55effc1b935036f8fd5c3373705e"`
Dec 02 11:04:31.354 INFO [stderr] usermod: no changes
Dec 02 11:04:31.471 INFO [stderr]    Compiling wemo v0.0.12 (/source)
Dec 02 11:04:46.607 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.24s
Dec 02 11:04:46.622 INFO [stderr] su: No module specific data is present
Dec 02 11:04:47.091 INFO running `"docker" "inspect" "6f3d2ccb566c0e5cfd854543ada1e21898cb55effc1b935036f8fd5c3373705e"`
Dec 02 11:04:47.323 INFO running `"docker" "rm" "-f" "6f3d2ccb566c0e5cfd854543ada1e21898cb55effc1b935036f8fd5c3373705e"`
Dec 02 11:04:47.607 INFO [stdout] 6f3d2ccb566c0e5cfd854543ada1e21898cb55effc1b935036f8fd5c3373705e
Dec 02 11:04:47.613 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-7/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 11:04:48.023 INFO [stdout] 959eadc24f1c0dc6b5730bea26c13ae4563548da955e69b2ba07f8c686474e37
Dec 02 11:04:48.028 INFO running `"docker" "start" "-a" "959eadc24f1c0dc6b5730bea26c13ae4563548da955e69b2ba07f8c686474e37"`
Dec 02 11:04:49.254 INFO [stderr] usermod: no changes
Dec 02 11:04:49.395 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Dec 02 11:04:49.411 INFO [stdout] 
Dec 02 11:04:49.411 INFO [stdout] running 16 tests
Dec 02 11:04:49.411 INFO [stderr]      Running /target/debug/deps/wemo-1aff29a5eb3aabb8
Dec 02 11:04:49.419 INFO [stdout] test device::switch::tests::test_get_ip_address_with_dynamic_ip ... ok
Dec 02 11:04:49.419 INFO [stdout] test device::switch::tests::test_get_ip_address_with_no_ip ... ok
Dec 02 11:04:49.419 INFO [stdout] test device::switch::tests::test_get_ip_address_with_static_ip ... ok
Dec 02 11:04:49.419 INFO [stdout] test device::switch::tests::test_get_port_with_port_set ... ok
Dec 02 11:04:49.419 INFO [stdout] test device::switch::tests::test_get_port_without_port_set ... ok
Dec 02 11:04:49.419 INFO [stdout] test device::switch::tests::test_name_with_ip ... ok
Dec 02 11:04:49.419 INFO [stdout] test device::switch::tests::test_name_without_ip ... ok
Dec 02 11:04:49.419 INFO [stdout] test device::switch::tests::test_update_location_with_dynamic_ip ... ok
Dec 02 11:04:49.419 INFO [stdout] test device::switch::tests::test_name_with_ip_and_port ... ok
Dec 02 11:04:49.431 INFO [stdout] test device::switch::tests::test_update_location_with_static_ip ... ok
Dec 02 11:04:49.435 INFO [stdout] test parsing::tests::insight_notifications ... ok
Dec 02 11:04:49.435 INFO [stdout] test parsing::tests::switch_notifications ... ok
Dec 02 11:04:49.503 INFO [stdout] test subscriptions::tests::test_send_subscribe ... ok
Dec 02 11:04:49.627 INFO [stdout] test xml::tests::test_find_tag_value ... ok
Dec 02 11:04:49.663 INFO [stdout] test xml::tests::test_find_tag_value_failure ... ok
Dec 02 11:04:49.715 INFO [stdout] test subscriptions::tests::test_callback_invocation ... FAILED
Dec 02 11:04:49.715 INFO [stdout] 
Dec 02 11:04:49.715 INFO [stdout] failures:
Dec 02 11:04:49.715 INFO [stdout] 
Dec 02 11:04:49.715 INFO [stdout] ---- subscriptions::tests::test_callback_invocation stdout ----
Dec 02 11:04:49.715 INFO [stdout] thread 'subscriptions::tests::test_callback_invocation' panicked at 'assertion failed: notice.is_some()', src/subscriptions.rs:400:5
Dec 02 11:04:49.715 INFO [stdout] stack backtrace:
Dec 02 11:04:49.715 INFO [stdout]    0:     0x565143c9707f - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3a8b83e1922cbdde
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 02 11:04:49.715 INFO [stdout]    1:     0x565143ca1017 - std::sys_common::backtrace::print::h00d3155a29b4020f
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 02 11:04:49.715 INFO [stdout]    2:     0x565143c9bbaf - std::panicking::default_hook::{{closure}}::hd7465c742e79e1bb
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panicking.rs:211
Dec 02 11:04:49.715 INFO [stdout]    3:     0x565143c9b8ae - std::panicking::default_hook::h9e3966f2aa2a6e12
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panicking.rs:221
Dec 02 11:04:49.715 INFO [stdout]    4:     0x565143c9c28e - std::panicking::rust_panic_with_hook::h587f091ae747d080
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panicking.rs:476
Dec 02 11:04:49.715 INFO [stdout]    5:     0x565143b87487 - std::panicking::begin_panic::h7fa6ffe2d69747d7
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panicking.rs:410
Dec 02 11:04:49.715 INFO [stdout]    6:     0x565143956f89 - wemo::subscriptions::tests::test_callback_invocation::haae907e2fe427754
Dec 02 11:04:49.715 INFO [stdout]                                at src/subscriptions.rs:400
Dec 02 11:04:49.715 INFO [stdout]    7:     0x565143956a19 - wemo::subscriptions::tests::test_callback_invocation::{{closure}}::hf93346160271d6cd
Dec 02 11:04:49.715 INFO [stdout]                                at src/subscriptions.rs:365
Dec 02 11:04:49.715 INFO [stdout]    8:     0x56514394f64d - core::ops::function::FnOnce::call_once::h15af09861fd288af
Dec 02 11:04:49.715 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 02 11:04:49.715 INFO [stdout]    9:     0x5651439978be - <F as alloc::boxed::FnBox<A>>::call_box::hac903f91577a156c
Dec 02 11:04:49.715 INFO [stdout]                                at libtest/lib.rs:1468
Dec 02 11:04:49.715 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 02 11:04:49.715 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 02 11:04:49.715 INFO [stdout]   10:     0x565143cabfc9 - __rust_maybe_catch_panic
Dec 02 11:04:49.715 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 02 11:04:49.715 INFO [stdout]   11:     0x5651439b9512 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb8f0d0309526c184
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panicking.rs:289
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panic.rs:392
Dec 02 11:04:49.715 INFO [stdout]                                at libtest/lib.rs:1423
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 02 11:04:49.715 INFO [stdout]   12:     0x5651439b9fc4 - std::panicking::try::do_call::hc2f7c8b9a348d8db
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panic.rs:313
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panicking.rs:310
Dec 02 11:04:49.715 INFO [stdout]   13:     0x565143cabfc9 - __rust_maybe_catch_panic
Dec 02 11:04:49.715 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 02 11:04:49.715 INFO [stdout]   14:     0x5651439a71fc - <F as alloc::boxed::FnBox<A>>::call_box::h6bfded618e480b32
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panicking.rs:289
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/panic.rs:392
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 02 11:04:49.715 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 02 11:04:49.715 INFO [stdout]   15:     0x565143c9990d - std::sys_common::thread::start_thread::h4c28257d67cdf9cb
Dec 02 11:04:49.715 INFO [stdout]                                at liballoc/boxed.rs:682
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 02 11:04:49.715 INFO [stdout]   16:     0x565143c8a435 - std::sys::unix::thread::Thread::new::thread_start::hfecb2e7eff8fd34f
Dec 02 11:04:49.715 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 02 11:04:49.715 INFO [stdout]   17:     0x7f41e265d6b9 - start_thread
Dec 02 11:04:49.715 INFO [stdout]   18:     0x7f41e217d41c - clone
Dec 02 11:04:49.715 INFO [stdout]   19:                0x0 - <unknown>
Dec 02 11:04:49.715 INFO [stdout] 
Dec 02 11:04:49.715 INFO [stdout] 
Dec 02 11:04:49.715 INFO [stdout] failures:
Dec 02 11:04:49.715 INFO [stdout]     subscriptions::tests::test_callback_invocation
Dec 02 11:04:49.715 INFO [stdout] 
Dec 02 11:04:49.715 INFO [stdout] test result: FAILED. 15 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 11:04:49.716 INFO [stdout] 
Dec 02 11:04:49.722 INFO [stderr] error: test failed, to rerun pass '--lib'
Dec 02 11:04:49.724 INFO [stderr] su: No module specific data is present
Dec 02 11:04:50.332 INFO running `"docker" "inspect" "959eadc24f1c0dc6b5730bea26c13ae4563548da955e69b2ba07f8c686474e37"`
Dec 02 11:04:50.637 INFO running `"docker" "rm" "-f" "959eadc24f1c0dc6b5730bea26c13ae4563548da955e69b2ba07f8c686474e37"`
Dec 02 11:04:50.923 INFO [stdout] 959eadc24f1c0dc6b5730bea26c13ae4563548da955e69b2ba07f8c686474e37
